﻿2026-09-09T00:58:10.9397708Z ##[group]Run bash .github/bench/wrap.sh twohost-web-b -- cargo test -p spt-daemon --test twohost_web -- --nocapture
2026-09-09T00:58:10.9398508Z [36;1mbash .github/bench/wrap.sh twohost-web-b -- cargo test -p spt-daemon --test twohost_web -- --nocapture[0m
2026-09-09T00:58:10.9415022Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}
2026-09-09T00:58:10.9415293Z env:
2026-09-09T00:58:10.9415584Z   SPT_CI_TEST_TMP: /home/reavus/actions-runner/_work/_temp/spt-test-tmp-34262154550-3-ZZdNcC
2026-09-09T00:58:10.9416024Z   TEMP: /home/reavus/actions-runner/_work/_temp/spt-test-tmp-34262154550-3-ZZdNcC
2026-09-09T00:58:10.9416418Z   TMP: /home/reavus/actions-runner/_work/_temp/spt-test-tmp-34262154550-3-ZZdNcC
2026-09-09T00:58:10.9416830Z   TMPDIR: /home/reavus/actions-runner/_work/_temp/spt-test-tmp-34262154550-3-ZZdNcC
2026-09-09T00:58:10.9417149Z   SPT_TWO_HOST: 1
2026-09-09T00:58:10.9417345Z   SPT_TWO_HOST_ROLE: b
2026-09-09T00:58:10.9417540Z   SPT_TWO_HOST_SECRET: rig-twohost-ci
2026-09-09T00:58:10.9417812Z   SPT_TWO_HOST_PEER_IP: 100.68.35.65
2026-09-09T00:58:10.9418017Z   SPT_TWO_HOST_WAIT_SECS: 900
2026-09-09T00:58:10.9418218Z ##[endgroup]
2026-09-09T00:58:11.3817669Z    Compiling spt-daemon v0.68.0 (/home/reavus/actions-runner/_work/spt-bs-core/spt-bs-core/crates/spt-daemon)
2026-09-09T00:58:12.9067196Z     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.94s
2026-09-09T00:58:12.9249553Z      Running tests/twohost_web.rs (target/debug/deps/twohost_web-faf8108c04874cf8)
2026-09-09T00:58:12.9281108Z 
2026-09-09T00:58:12.9281486Z running 5 tests
2026-09-09T00:58:12.9285043Z test two_host_web_deny_role_a ... ok
2026-09-09T00:58:12.9285572Z test two_host_web_fetch_role_a ... ok
2026-09-09T00:58:12.9286021Z test two_host_web_helper_role_a ... ok
2026-09-09T00:58:12.9286307Z test two_host_web_range_role_a ... ok
2026-09-09T00:58:12.9289698Z TWOHOST-WEB role B: node 9bbcee970607c7454b0baf8b38b032ccdf1a36de7ef04d614158fecd1f08b612 serving on udp 7481
2026-09-09T00:58:12.9391116Z ACCESS_STORE_MINTED /home/reavus/actions-runner/_work/_temp/spt-test-tmp-34262154550-3-ZZdNcC/.tmpHoQSVg/identity/trust/access.json — no access store was present at first load in this process, so an EMPTY baseline was written (everything open that the chain leaves open). If this node had access rules, they are GONE: the store was removed while the daemon was down, and this mint replaced it. Re-apply them (`spt endpoint access ...`); a restart cannot tell a wipe from a first boot.
2026-09-09T00:58:12.9561848Z TWOHOST-WEB role B: audience row merged, verdicts [Merged(Inserted)]
2026-09-09T00:58:12.9569345Z CONN_LIFECYCLE: conn=1 role=brain wall_ms=1788915492956 mono_ms=0 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:12.9682547Z PAIR_MEET_UP:twohost step=59630516 id=cd1651394cd01d5b2e6ef7f6aed6639a2410fa30fe8d6c7fd1f757ca68c16ac6 addr=EndpointAddr { id: PublicKey(cd1651394cd01d5b2e6ef7f6aed6639a2410fa30fe8d6c7fd1f757ca68c16ac6), addrs: {Ip(100.98.197.12:40881), Ip(192.168.1.168:40881)} }
2026-09-09T00:58:19.5052818Z CONN_LIFECYCLE: conn=3 role=brain wall_ms=1788915499505 mono_ms=6548 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:19.5053515Z CONN_LIFECYCLE: conn=4 role=brain wall_ms=1788915499505 mono_ms=6548 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:19.5054556Z CONN_LIFECYCLE: conn=3 role=brain wall_ms=1788915499505 mono_ms=6548 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:19.5055886Z CONN_LIFECYCLE: conn=4 role=brain wall_ms=1788915499505 mono_ms=6548 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:19.5057913Z ACCESS_REFUSED WEB to w1-locked from 0cef34af5151a8a2b534f6d29a543b3ad35e2c1a4b2fe91bf60f027232a135fd (tier EndpointRule(Node { node: "0cef34af5151a8a2b534f6d29a543b3ad35e2c1a4b2fe91bf60f027232a135fd" }))
2026-09-09T00:58:19.5059770Z WEB_STREAM: stream=2 origin=0cef34af5151a8a2b534f6d29a543b3ad35e2c1a4b2fe91bf60f027232a135fd outcome=refused reason=ACCESS_DENIED: WEB: the owning node's access rules refuse this request
2026-09-09T00:58:19.5061888Z WEB_STREAM: stream=1 origin=6609402a9c961143ce5f22103095f4c4a2ff884dccdfc49b7e88b12c29c7d04e outcome=sent status=206 bytes=4 path=/kitsubito/f/open.md
2026-09-09T00:58:19.5062587Z CONN_LIFECYCLE: conn=6 role=brain wall_ms=1788915499505 mono_ms=6549 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:19.5063374Z CONN_LIFECYCLE: conn=6 role=brain wall_ms=1788915499506 mono_ms=6549 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:19.5064147Z CONN_LIFECYCLE: conn=7 role=brain wall_ms=1788915499506 mono_ms=6549 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:19.5065039Z CONN_LIFECYCLE: conn=7 role=brain wall_ms=1788915499506 mono_ms=6549 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:19.5066531Z CONN_LIFECYCLE: conn=5 role=brain wall_ms=1788915499506 mono_ms=6549 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:19.5067877Z CONN_LIFECYCLE: conn=5 role=brain wall_ms=1788915499506 mono_ms=6549 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:19.5069195Z CONN_LIFECYCLE: conn=8 role=brain wall_ms=1788915499506 mono_ms=6550 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:19.5069851Z CONN_LIFECYCLE: conn=8 role=brain wall_ms=1788915499506 mono_ms=6550 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:19.5568573Z CONN_LIFECYCLE: conn=9 role=brain wall_ms=1788915499556 mono_ms=6599 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:19.5570316Z CONN_LIFECYCLE: conn=9 role=brain wall_ms=1788915499556 mono_ms=6599 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:19.5580121Z WEB_STREAM: stream=3 origin=cff883e9631ed98668018c069cbc265a97217b942f5276e4f19ac15bdef5f349 outcome=sent status=200 bytes=35 path=/kitsubito/f/open.md
2026-09-09T00:58:19.5592024Z CONN_LIFECYCLE: conn=10 role=brain wall_ms=1788915499559 mono_ms=6602 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:19.5594256Z CONN_LIFECYCLE: conn=10 role=brain wall_ms=1788915499559 mono_ms=6602 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:19.5596537Z CONN_LIFECYCLE: conn=11 role=brain wall_ms=1788915499559 mono_ms=6602 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:19.5597530Z CONN_LIFECYCLE: conn=11 role=brain wall_ms=1788915499559 mono_ms=6602 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:22.9598132Z CONN_LIFECYCLE: conn=2 role=brain wall_ms=1788915502959 mono_ms=10002 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:22.9600694Z TWOHOST-WEB role B: A not ready for the message yet (broker QUIC op exceeded the 10s bound (peer unresponsive) — failing as an ordinary per-peer error)
2026-09-09T00:58:24.9844093Z CONN_LIFECYCLE: conn=2 role=brain stream-subscriber stream=4 wall_ms=1788915504984 mono_ms=12027 event=stream-sub-attach stream=4 from_seq=0 [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:25.1536052Z F1_ARM=vi: waiting for A's ingress to register on its user's authority
2026-09-09T00:58:25.1537034Z TWOHOST-WEB role B: the user's message was ADMITTED by A (Spooled)
2026-09-09T00:58:25.1538198Z CONN_LIFECYCLE: conn=2 role=brain stream-subscriber stream=4 wall_ms=1788915505153 mono_ms=12196 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:25.1929123Z CONN_LIFECYCLE: conn=12 role=brain wall_ms=1788915505192 mono_ms=12235 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:25.1930566Z CONN_LIFECYCLE: conn=12 role=brain wall_ms=1788915505192 mono_ms=12236 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:25.1946212Z WEB_SERVE_FOR: stream=5 origin=a819392896e272997e2841a1d6db43fb8a4aae82b3983456c8791770f9d3c083 outcome=registered name=quoted-by-the-user.md ttl_ms=86400000 audience=w2-helper-audience
2026-09-09T00:58:25.1947620Z CONN_LIFECYCLE: conn=13 role=brain wall_ms=1788915505194 mono_ms=12237 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:25.1948493Z CONN_LIFECYCLE: conn=13 role=brain wall_ms=1788915505194 mono_ms=12237 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:25.1950719Z CONN_LIFECYCLE: conn=14 role=brain wall_ms=1788915505194 mono_ms=12238 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:25.1951545Z CONN_LIFECYCLE: conn=14 role=brain wall_ms=1788915505195 mono_ms=12238 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:25.4966477Z CONN_LIFECYCLE: conn=15 role=brain wall_ms=1788915505496 mono_ms=12539 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:25.4967886Z CONN_LIFECYCLE: conn=15 role=brain wall_ms=1788915505496 mono_ms=12539 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:25.4977739Z WEB_STREAM: stream=6 origin=a819392896e272997e2841a1d6db43fb8a4aae82b3983456c8791770f9d3c083 outcome=sent status=200 bytes=31 path=/kitsubito/f/quoted-by-the-user.md
2026-09-09T00:58:25.4985497Z CONN_LIFECYCLE: conn=16 role=brain wall_ms=1788915505498 mono_ms=12541 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:25.4987413Z CONN_LIFECYCLE: conn=16 role=brain wall_ms=1788915505498 mono_ms=12541 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:25.4989577Z CONN_LIFECYCLE: conn=17 role=brain wall_ms=1788915505498 mono_ms=12542 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:25.4990797Z CONN_LIFECYCLE: conn=17 role=brain wall_ms=1788915505498 mono_ms=12542 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:25.6547802Z TWOHOST OK: role B registered quoted-by-the-user.md for w2-helper-audience on its user's authority in 500 ms (kind=File ttl=Some(86400000) origin=Some("BCDFGH23"))
2026-09-09T00:58:25.6549571Z TWOHOST OK: role B serving open.md + locked.md (waiting for 5 served cells)
2026-09-09T00:58:25.6550172Z TWOHOST-WEB role B: served cell 1/5
2026-09-09T00:58:25.6550598Z TWOHOST-WEB role B: served cell 2/5
2026-09-09T00:58:25.6550996Z TWOHOST-WEB role B: served cell 3/5
2026-09-09T00:58:25.6551381Z TWOHOST-WEB role B: served cell 4/5
2026-09-09T00:58:25.6551766Z TWOHOST-WEB role B: served cell 5/5
2026-09-09T00:58:30.9725568Z PAIR_MEET_UP:twohost step=59630517 id=596b67fea707854be6979d3433c24de8d30e0b452be285ea2b8009f0110fee04 addr=EndpointAddr { id: PublicKey(596b67fea707854be6979d3433c24de8d30e0b452be285ea2b8009f0110fee04), addrs: {Ip(100.98.197.12:41033), Ip(192.168.1.168:41033)} }
2026-09-09T00:58:35.6550860Z TWOHOST OK: role B served all 5 requester cells
2026-09-09T00:58:35.6557767Z CONN_LIFECYCLE: conn=1 role=brain wall_ms=1788915515655 mono_ms=22698 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T00:58:35.6563469Z test two_host_web_role_b ... ok
2026-09-09T00:58:35.6563963Z 
2026-09-09T00:58:35.6564431Z test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 22.73s
2026-09-09T00:58:35.6564908Z 
